Dec 03 15:45:11.175 INFO testing cocaine-0.9.2 against master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61 for beta-1.31-5
Dec 03 15:45:11.175 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt build --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 15:45:11.412 INFO [stdout] 683d35f877a43907a734d505c6ebeab609128167e939a336f60ee4ebbfb0ae7e
Dec 03 15:45:11.415 INFO running `"docker" "start" "-a" "683d35f877a43907a734d505c6ebeab609128167e939a336f60ee4ebbfb0ae7e"`
Dec 03 15:45:11.966 INFO [stderr] usermod: no changes
Dec 03 15:45:12.131 INFO [stderr]    Compiling rmpv v0.4.0
Dec 03 15:45:14.655 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 03 15:45:24.049 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 12.06s
Dec 03 15:45:24.059 INFO [stderr] su: No module specific data is present
Dec 03 15:45:24.893 INFO running `"docker" "inspect" "683d35f877a43907a734d505c6ebeab609128167e939a336f60ee4ebbfb0ae7e"`
Dec 03 15:45:25.228 INFO running `"docker" "rm" "-f" "683d35f877a43907a734d505c6ebeab609128167e939a336f60ee4ebbfb0ae7e"`
Dec 03 15:45:25.555 INFO [stdout] 683d35f877a43907a734d505c6ebeab609128167e939a336f60ee4ebbfb0ae7e
Dec 03 15:45:25.563 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen --no-run" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 15:45:26.000 INFO [stdout] 7c77567712021a313e107dbfc380f16c90d004d5f541f6797b4c3a0631cc598b
Dec 03 15:45:26.002 INFO running `"docker" "start" "-a" "7c77567712021a313e107dbfc380f16c90d004d5f541f6797b4c3a0631cc598b"`
Dec 03 15:45:27.358 INFO [stderr] usermod: no changes
Dec 03 15:45:27.620 INFO [stderr]    Compiling slog-envlogger v0.5.0
Dec 03 15:45:31.047 INFO [stderr]    Compiling cocaine v0.9.2 (/source)
Dec 03 15:46:27.316 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 59.91s
Dec 03 15:46:27.335 INFO [stderr] su: No module specific data is present
Dec 03 15:46:28.759 INFO running `"docker" "inspect" "7c77567712021a313e107dbfc380f16c90d004d5f541f6797b4c3a0631cc598b"`
Dec 03 15:46:29.149 INFO running `"docker" "rm" "-f" "7c77567712021a313e107dbfc380f16c90d004d5f541f6797b4c3a0631cc598b"`
Dec 03 15:46:29.463 INFO [stdout] 7c77567712021a313e107dbfc380f16c90d004d5f541f6797b4c3a0631cc598b
Dec 03 15:46:29.467 INFO running `"docker" "create" "-v" "/mnt/big/crater/work/local/target-dirs/beta-1.31-5/worker-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/target:rw,Z" "-v" "/mnt/big/crater/work/local/test-source/worker-5/beta-1.31-5/master#8940af3cea4a9a9deabcaf843b2f8cad9a83fc61:/source:ro,Z" "-v" "/mnt/big/crater/work/local/cargo-home:/cargo-home:ro,Z" "-v" "/mnt/big/crater/work/local/rustup-home:/rustup-home:ro,Z" "-e" "USER_ID=1000" "-e" "SOURCE_DIR=/source" "-e" "USER_ID=1000" "-e" "CMD=cargo +8940af3cea4a9a9deabcaf843b2f8cad9a83fc61-alt test --frozen" "-e" "CARGO_TARGET_DIR=/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "CARGO_HOME=/cargo-home" "-e" "RUSTUP_HOME=/rustup-home" "-m" "1536M" "--network" "none" "crater"`
Dec 03 15:46:29.935 INFO [stdout] 3244024ecdf6cec606c016216a04920cdd95a059baf1a1e37a2c9ef01aec7ed5
Dec 03 15:46:29.938 INFO running `"docker" "start" "-a" "3244024ecdf6cec606c016216a04920cdd95a059baf1a1e37a2c9ef01aec7ed5"`
Dec 03 15:46:31.423 INFO [stderr] usermod: no changes
Dec 03 15:46:31.755 INFO [stderr]     Finished dev [unoptimized + debuginfo] target(s) in 0.29s
Dec 03 15:46:31.779 INFO [stderr]      Running /target/debug/deps/cocaine-863032c5b034ba6c
Dec 03 15:46:31.779 INFO [stdout] 
Dec 03 15:46:31.779 INFO [stdout] running 14 tests
Dec 03 15:46:31.811 INFO [stdout] test logging::tests::test_macro_with_attributes ... ok
Dec 03 15:46:31.811 INFO [stdout] test logging::tests::test_macro_with_attribute ... ok
Dec 03 15:46:31.811 INFO [stdout] test logging::tests::test_macro_with_args_and_attributes ... ok
Dec 03 15:46:31.811 INFO [stdout] test logging::tests::test_macro_with_args ... ok
Dec 03 15:46:31.811 INFO [stdout] test frame::test::pass ... ok
Dec 03 15:46:31.811 INFO [stdout] test resolve::test::fixed_resolver_default_addrs ... ok
Dec 03 15:46:31.811 INFO [stdout] test logging::tests::test_macro_without_args ... ok
Dec 03 15:46:31.811 INFO [stdout] test resolve::test::fixed_resolver_saves_addrs ... ok
Dec 03 15:46:31.855 INFO [stdout] test net::connect::tests::test_connection_refused ... ok
Dec 03 15:46:31.855 INFO [stdout] test net::connect::tests::test_connect ... ok
Dec 03 15:46:31.867 INFO [stdout] test net::connect::tests::test_connect_multi ... ok
Dec 03 15:46:31.951 INFO [stdout] test frame::test::fail_invalid_type_type ... ok
Dec 03 15:46:31.951 INFO [stdout] test frame::test::fail_invalid_frame_type ... ok
Dec 03 15:46:31.951 INFO [stdout] test frame::test::fail_invalid_chan_type ... ok
Dec 03 15:46:31.951 INFO [stdout] 
Dec 03 15:46:31.951 INFO [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 15:46:31.951 INFO [stdout] 
Dec 03 15:46:31.951 INFO [stderr]      Running /target/debug/deps/service-7d49d10e5cc4bd99
Dec 03 15:46:31.955 INFO [stdout] 
Dec 03 15:46:31.955 INFO [stdout] running 4 tests
Dec 03 15:46:32.007 INFO [stdout] test connection_refused_because_invalid_framing ... ok
Dec 03 15:46:32.023 INFO [stderr]    Doc-tests cocaine
Dec 03 15:46:32.023 INFO [stdout] test dispatch_receives_rst ... ok
Dec 03 15:46:32.023 INFO [stdout] test connect ... ok
Dec 03 15:46:32.023 INFO [stdout] test connection_refused ... ok
Dec 03 15:46:32.023 INFO [stdout] 
Dec 03 15:46:32.023 INFO [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 15:46:32.023 INFO [stdout] 
Dec 03 15:46:40.719 INFO [stdout] 
Dec 03 15:46:40.719 INFO [stdout] running 19 tests
Dec 03 15:47:30.867 INFO [stdout] test src/lib.rs -  (line 23) ... ok
Dec 03 15:47:34.028 INFO [stdout] test src/logging.rs - cocaine_log (line 354) ... ok
Dec 03 15:47:34.207 INFO [stdout] test src/resolve.rs - resolve::Resolver (line 122) ... ok
Dec 03 15:47:34.451 INFO [stdout] test src/request.rs - request::Request (line 16) ... ok
Dec 03 15:47:36.027 INFO [stdout] test src/lib.rs -  (line 42) ... ok
Dec 03 15:47:37.386 INFO [stdout] test src/logging.rs - cocaine_log (line 371) ... ok
Dec 03 15:47:38.559 INFO [stdout] test src/logging.rs - logging::LoggerContext::new (line 178) ... ok
Dec 03 15:47:40.739 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... test src/lib.rs - Service::new (line 1301) has been running for over 60 seconds
Dec 03 15:47:44.335 INFO [stdout] test src/lib.rs - Service::new (line 1301) ... ok
Dec 03 15:48:30.879 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) has been running for over 60 seconds
Dec 03 15:48:30.987 INFO [stdout] test src/service/locator.rs - service::locator::Locator::resolve (line 48) ... ok
Dec 03 15:48:33.911 INFO [stdout] test src/response.rs - response::Response<'a, 'b>::deserialize (line 71) ... ok
Dec 03 15:48:34.203 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... test src/service/mod.rs - service::ServiceBuilder (line 82) has been running for over 60 seconds
Dec 03 15:48:34.455 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) has been running for over 60 seconds
Dec 03 15:48:36.047 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) has been running for over 60 seconds
Dec 03 15:48:37.387 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... test src/service/storage.rs - service::storage::Storage::read (line 31) has been running for over 60 seconds
Dec 03 15:48:38.567 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... test src/service/storage.rs - service::storage::Storage::write (line 60) has been running for over 60 seconds
Dec 03 15:48:41.314 INFO [stdout] test src/service/storage.rs - service::storage::Storage::read (line 31) ... ok
Dec 03 15:48:44.339 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) has been running for over 60 seconds
Dec 03 15:48:45.883 INFO [stdout] test src/service/storage.rs - service::storage::Storage::write (line 60) ... ok
Dec 03 15:48:48.713 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder (line 82) ... ok
Dec 03 15:48:48.831 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<ResolverBuilder>::locator_addrs (line 124) ... ok
Dec 03 15:48:50.482 INFO [stdout] test src/service/mod.rs - service::ServiceBuilder<T>::resolver (line 176) ... ok
Dec 03 15:48:51.727 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::create (line 101) ... ok
Dec 03 15:49:06.398 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::del (line 176) ... ok
Dec 03 15:49:08.008 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::get (line 212) ... ok
Dec 03 15:49:08.387 INFO [stdout] test src/service/unicorn.rs - service::unicorn::Unicorn::put (line 139) ... ok
Dec 03 15:49:08.387 INFO [stdout] 
Dec 03 15:49:08.387 INFO [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out
Dec 03 15:49:08.387 INFO [stdout] 
Dec 03 15:49:08.406 INFO [stderr] su: No module specific data is present
Dec 03 15:49:09.217 INFO running `"docker" "inspect" "3244024ecdf6cec606c016216a04920cdd95a059baf1a1e37a2c9ef01aec7ed5"`
Dec 03 15:49:09.505 INFO running `"docker" "rm" "-f" "3244024ecdf6cec606c016216a04920cdd95a059baf1a1e37a2c9ef01aec7ed5"`
Dec 03 15:49:09.843 INFO [stdout] 3244024ecdf6cec606c016216a04920cdd95a059baf1a1e37a2c9ef01aec7ed5
